[INFO] cloning repository https://github.com/karnaukhovlad/OrderingAdapter
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/karnaukhovlad/OrderingAdapter" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkarnaukhovlad%2FOrderingAdapter", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkarnaukhovlad%2FOrderingAdapter'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6356eaf0e27fd5d846a90524162c459c17581f27
[INFO] testing karnaukhovlad/OrderingAdapter against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkarnaukhovlad%2FOrderingAdapter" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/karnaukhovlad/OrderingAdapter
[INFO] finished tweaking git repo https://github.com/karnaukhovlad/OrderingAdapter
[INFO] tweaked toml for git repo https://github.com/karnaukhovlad/OrderingAdapter written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/karnaukhovlad/OrderingAdapter on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/karnaukhovlad/OrderingAdapter already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded jiff-static v0.2.13
[INFO] [stderr]   Downloaded cc v1.2.22
[INFO] [stderr]   Downloaded jiff v0.2.13
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 99502aa0a8dd9d3a06c9b28c9dec9092286d2c6b604537d6c700ad034faa4ca1
[INFO] running `Command { std: "docker" "start" "-a" "99502aa0a8dd9d3a06c9b28c9dec9092286d2c6b604537d6c700ad034faa4ca1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "99502aa0a8dd9d3a06c9b28c9dec9092286d2c6b604537d6c700ad034faa4ca1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "99502aa0a8dd9d3a06c9b28c9dec9092286d2c6b604537d6c700ad034faa4ca1", kill_on_drop: false }`
[INFO] [stdout] 99502aa0a8dd9d3a06c9b28c9dec9092286d2c6b604537d6c700ad034faa4ca1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ff5c6293cec60e4ff0326f0c0deefc1cd5f0392a59cc2665e06172c0119fd75a
[INFO] running `Command { std: "docker" "start" "-a" "ff5c6293cec60e4ff0326f0c0deefc1cd5f0392a59cc2665e06172c0119fd75a", kill_on_drop: false }`
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling smallvec v1.15.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling syn v2.0.101
[INFO] [stderr]    Compiling anstream v0.6.18
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling socket2 v0.5.9
[INFO] [stderr]    Compiling jiff v0.2.13
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling tokio v1.45.0
[INFO] [stderr]    Compiling env_filter v0.1.3
[INFO] [stderr]    Compiling env_logger v0.11.8
[INFO] [stderr]    Compiling ordering_adapter v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 34.43s
[INFO] running `Command { std: "docker" "inspect" "ff5c6293cec60e4ff0326f0c0deefc1cd5f0392a59cc2665e06172c0119fd75a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ff5c6293cec60e4ff0326f0c0deefc1cd5f0392a59cc2665e06172c0119fd75a", kill_on_drop: false }`
[INFO] [stdout] ff5c6293cec60e4ff0326f0c0deefc1cd5f0392a59cc2665e06172c0119fd75a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1768ede813349471fd12c125c379f0e50afa1f40f03d7cb30c4b3822879dbfb7
[INFO] running `Command { std: "docker" "start" "-a" "1768ede813349471fd12c125c379f0e50afa1f40f03d7cb30c4b3822879dbfb7", kill_on_drop: false }`
[INFO] [stderr]    Compiling ordering_adapter v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: value assigned to `total_tx_sent` is never read
[INFO] [stdout]    --> src/main.rs:392:33
[INFO] [stdout]     |
[INFO] [stdout] 392 |         let mut total_tx_sent = 0;
[INFO] [stdout]     |                                 ^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 429 |                 total_tx_sent = geyser_handle.await.expect("Geyser task panicked");
[INFO] [stdout]     |                 ------------------------------------------------------------------ `total_tx_sent` is overwritten here before the previous value is read
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.25s
[INFO] running `Command { std: "docker" "inspect" "1768ede813349471fd12c125c379f0e50afa1f40f03d7cb30c4b3822879dbfb7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1768ede813349471fd12c125c379f0e50afa1f40f03d7cb30c4b3822879dbfb7", kill_on_drop: false }`
[INFO] [stdout] 1768ede813349471fd12c125c379f0e50afa1f40f03d7cb30c4b3822879dbfb7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f4efc8731360ab0602f60d0addca0d46cfcbd5659f07e456596c240cef9f4fcd
[INFO] running `Command { std: "docker" "start" "-a" "f4efc8731360ab0602f60d0addca0d46cfcbd5659f07e456596c240cef9f4fcd", kill_on_drop: false }`
[INFO] [stderr] warning: value assigned to `total_tx_sent` is never read
[INFO] [stderr]    --> src/main.rs:392:33
[INFO] [stderr]     |
[INFO] [stderr] 392 |         let mut total_tx_sent = 0;
[INFO] [stderr]     |                                 ^ this value is reassigned later and never used
[INFO] [stderr] ...
[INFO] [stderr] 429 |                 total_tx_sent = geyser_handle.await.expect("Geyser task panicked");
[INFO] [stderr]     |                 ------------------------------------------------------------------ `total_tx_sent` is overwritten here before the previous value is read
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `ordering_adapter` (bin "ordering_adapter" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/ordering_adapter-9d3f1576dd2fba72)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] 2026-06-13 02:09:39.289 [INFO] - Starting test_geyser_ordering_adapter
[INFO] [stderr] 2026-06-13 02:09:39.290 [INFO] - [Test] Starting to receive transactions. Test timeout: 15s
[INFO] [stderr] 2026-06-13 02:09:39.660 [INFO] - [Geyser] Finished sending Block: 0. Total transactions per block: 1652. Expected: 327ms, Actual: 326ms
[INFO] [stderr] 2026-06-13 02:09:40.047 [INFO] - [Adapter] Finished processing Block: 0. Duration: 668ms. Last Index: 1651
[INFO] [stderr] 2026-06-13 02:09:40.066 [INFO] - [Geyser] Finished sending Block: 1. Total transactions per block: 1919. Expected: 405ms, Actual: 405ms
[INFO] [stderr] 2026-06-13 02:09:40.415 [INFO] - [Geyser] Finished sending Block: 2. Total transactions per block: 1845. Expected: 350ms, Actual: 349ms
[INFO] [stderr] 2026-06-13 02:09:40.438 [INFO] - [Adapter] Finished processing Block: 1. Duration: 386ms. Last Index: 1918
[INFO] [stderr] 2026-06-13 02:09:40.784 [INFO] - [Geyser] Finished sending Block: 3. Total transactions per block: 1505. Expected: 368ms, Actual: 368ms
[INFO] [stderr] 2026-06-13 02:09:40.789 [INFO] - [Adapter] Finished processing Block: 2. Duration: 347ms. Last Index: 1844
[INFO] [stderr] 2026-06-13 02:09:41.190 [INFO] - [Adapter] Finished processing Block: 3. Duration: 397ms. Last Index: 1504
[INFO] [stderr] 2026-06-13 02:09:41.245 [INFO] - [Geyser] Finished sending Block: 4. Total transactions per block: 1925. Expected: 461ms, Actual: 460ms
[INFO] [stderr] 2026-06-13 02:09:41.245 [INFO] - [Geyser] All 5 blocks sent. Total transactions: 8846
[INFO] [stderr] 2026-06-13 02:09:41.246 [INFO] - [Adapter] Input channel closed. Flushing remaining buffer.
[INFO] [stderr] 2026-06-13 02:09:41.246 [INFO] - [Adapter] Finished processing. Total transactions sent: 8846
[INFO] [stderr] 2026-06-13 02:09:41.246 [INFO] - [Test] Successfully received all transactions.
[INFO] [stderr] 2026-06-13 02:09:41.246 [INFO] - [Test] Received a total of 8846 transactions.
[INFO] [stderr] 2026-06-13 02:09:41.248 [INFO] - [Test] All checks passed!
[INFO] [stdout] test tests::test_geyser_ordering_adapter ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.96s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f4efc8731360ab0602f60d0addca0d46cfcbd5659f07e456596c240cef9f4fcd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f4efc8731360ab0602f60d0addca0d46cfcbd5659f07e456596c240cef9f4fcd", kill_on_drop: false }`
[INFO] [stdout] f4efc8731360ab0602f60d0addca0d46cfcbd5659f07e456596c240cef9f4fcd
